Dec 01 23:15:14.300 INFO testing bhase/raytracerchallenge against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 01 23:15:14.300 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 23:15:14.743 INFO [stdout] f6e6751a3b5c463741a363d5c23e79363a1e3397c277e5960a9642af71c12716
Dec 01 23:15:14.755 INFO running `"docker" "start" "-a" "f6e6751a3b5c463741a363d5c23e79363a1e3397c277e5960a9642af71c12716"`
Dec 01 23:15:15.713 INFO [stderr] usermod: no changes
Dec 01 23:15:15.861 INFO [stderr]    Compiling raytracer_challenge v0.1.0 (/source)
Dec 01 23:15:17.288 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.53s
Dec 01 23:15:17.296 INFO [stderr] su: No module specific data is present
Dec 01 23:15:18.352 INFO running `"docker" "inspect" "f6e6751a3b5c463741a363d5c23e79363a1e3397c277e5960a9642af71c12716"`
Dec 01 23:15:18.629 INFO running `"docker" "rm" "-f" "f6e6751a3b5c463741a363d5c23e79363a1e3397c277e5960a9642af71c12716"`
Dec 01 23:15:18.927 INFO [stdout] f6e6751a3b5c463741a363d5c23e79363a1e3397c277e5960a9642af71c12716
Dec 01 23:15:18.935 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 23:15:19.368 INFO [stdout] a0755c5ecaa2e8fa5e61513448227e4990a3e94053b85a3c1dd3749ee70d7d4b
Dec 01 23:15:19.369 INFO running `"docker" "start" "-a" "a0755c5ecaa2e8fa5e61513448227e4990a3e94053b85a3c1dd3749ee70d7d4b"`
Dec 01 23:15:20.702 INFO [stderr] usermod: no changes
Dec 01 23:15:20.919 INFO [stderr]    Compiling raytracer_challenge v0.1.0 (/source)
Dec 01 23:15:31.320 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.59s
Dec 01 23:15:31.331 INFO [stderr] su: No module specific data is present
Dec 01 23:15:32.223 INFO running `"docker" "inspect" "a0755c5ecaa2e8fa5e61513448227e4990a3e94053b85a3c1dd3749ee70d7d4b"`
Dec 01 23:15:32.548 INFO running `"docker" "rm" "-f" "a0755c5ecaa2e8fa5e61513448227e4990a3e94053b85a3c1dd3749ee70d7d4b"`
Dec 01 23:15:32.870 INFO [stdout] a0755c5ecaa2e8fa5e61513448227e4990a3e94053b85a3c1dd3749ee70d7d4b
Dec 01 23:15:32.872 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 23:15:33.348 INFO [stdout] b9b8e13100f3b55ab3d8501a43331f15b9bee2032429ce9b80b78bfdfff14690
Dec 01 23:15:33.351 INFO running `"docker" "start" "-a" "b9b8e13100f3b55ab3d8501a43331f15b9bee2032429ce9b80b78bfdfff14690"`
Dec 01 23:15:34.721 INFO [stderr] usermod: no changes
Dec 01 23:15:34.943 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.18s
Dec 01 23:15:34.955 INFO [stderr]      Running /target/debug/deps/raytracer_challenge-45884a6d290c864f
Dec 01 23:15:34.955 INFO [stderr]      Running /target/debug/deps/raytracer-f1b15e3086480e5d
Dec 01 23:15:34.955 INFO [stdout] 
Dec 01 23:15:34.955 INFO [stdout] running 0 tests
Dec 01 23:15:34.955 INFO [stdout] 
Dec 01 23:15:34.955 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 23:15:34.955 INFO [stdout] 
Dec 01 23:15:34.963 INFO [stdout] 
Dec 01 23:15:34.963 INFO [stderr]      Running /target/debug/deps/cucumber-ba72ba42ff3c1880
Dec 01 23:15:34.963 INFO [stdout] running 0 tests
Dec 01 23:15:34.963 INFO [stdout] 
Dec 01 23:15:34.963 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 23:15:34.963 INFO [stdout] 
Dec 01 23:15:35.135 INFO [stdout] [0m[1m[32m[Cucumber v0.5.0]
Dec 01 23:15:35.135 INFO [stdout] 
Dec 01 23:15:35.170 INFO [stdout] [0m[0m[1m[37mFeature: Tuples, Vectors, and Points               [0m[37m features/tuples.feature:1:1
Dec 01 23:15:35.170 INFO [stdout] [0m
Dec 01 23:15:35.170 INFO [stdout] [0m[1m[37m Scenario: A tuple with w=1.0 is a point          [0m[37m features/tuples.feature:3:11
Dec 01 23:15:35.183 INFO [stdout] [0m[0m[32m  ✔ Given a := tuple(4.3, -4.2, 3.1, 1.0)          [0m[37m features/tuples.feature:4:3
Dec 01 23:15:35.185 INFO [stdout] [0m[0m[32m  ✔ Then a.x = 4.3                                 [0m[37m features/tuples.feature:5:3
Dec 01 23:15:35.185 INFO [stdout] [0m[0m[32m  ✔ And a.y = -4.2                                 [0m[37m features/tuples.feature:6:5
Dec 01 23:15:35.186 INFO [stdout] [0m[0m[32m  ✔ And a.z = 3.1                                  [0m[37m features/tuples.feature:7:5
Dec 01 23:15:35.186 INFO [stdout] [0m[0m[32m  ✔ And a.w = 1.0                                  [0m[37m features/tuples.feature:8:5
Dec 01 23:15:35.187 INFO [stdout] [0m[0m[32m  ✔ And a is a point                               [0m[37m features/tuples.feature:9:5
Dec 01 23:15:35.192 INFO [stdout] [0m[0m[32m  ✔ And a is not a vector                         [0m[37m features/tuples.feature:10:5
Dec 01 23:15:35.192 INFO [stdout] [0m
Dec 01 23:15:35.192 INFO [stdout] [0m[1m[37m Scenario: A tuple with w=0 is a vector          [0m[37m features/tuples.feature:12:11
Dec 01 23:15:35.192 INFO [stdout] [0m[0m[32m  ✔ Given a := tuple(4.3, -4.2, 3.1, 0.0)         [0m[37m features/tuples.feature:13:3
Dec 01 23:15:35.192 INFO [stdout] [0m[0m[32m  ✔ Then a.x = 4.3                                [0m[37m features/tuples.feature:14:3
Dec 01 23:15:35.192 INFO [stdout] [0m[0m[32m  ✔ And a.y = -4.2                                [0m[37m features/tuples.feature:15:5
Dec 01 23:15:35.192 INFO [stdout] [0m[0m[32m  ✔ And a.z = 3.1                                 [0m[37m features/tuples.feature:16:5
Dec 01 23:15:35.192 INFO [stdout] [0m[0m[32m  ✔ And a.w = 0.0                                 [0m[37m features/tuples.feature:17:5
Dec 01 23:15:35.193 INFO [stdout] [0m[0m[32m  ✔ And a is not a point                          [0m[37m features/tuples.feature:18:5
Dec 01 23:15:35.194 INFO [stdout] [0m[0m[32m  ✔ And a is a vector                             [0m[37m features/tuples.feature:19:5
Dec 01 23:15:35.194 INFO [stdout] [0m
Dec 01 23:15:35.194 INFO [stdout] [0m[1m[37m Scenario: "point" describes tuples with w=1     [0m[37m features/tuples.feature:21:11
Dec 01 23:15:35.195 INFO [stdout] [0m[0m[32m  ✔ Given p := point(4, -4, 3)                    [0m[37m features/tuples.feature:22:3
Dec 01 23:15:35.207 INFO [stdout] [0m[0m[32m  ✔ Then p = tuple(4, -4, 3, 1)                   [0m[37m features/tuples.feature:23:3
Dec 01 23:15:35.207 INFO [stdout] [0m
Dec 01 23:15:35.207 INFO [stdout] [0m[1m[37m Scenario: "vector" describes tuples with w=0    [0m[37m features/tuples.feature:25:11
Dec 01 23:15:35.207 INFO [stdout] [0m[0m[32m  ✔ Given v := vector(4, -4, 3)                   [0m[37m features/tuples.feature:26:3
Dec 01 23:15:35.207 INFO [stdout] [0m[0m[32m  ✔ Then v = tuple(4, -4, 3, 0)                   [0m[37m features/tuples.feature:27:3
Dec 01 23:15:35.207 INFO [stdout] [0m
Dec 01 23:15:35.207 INFO [stdout] [0m[1m[37m Scenario: Adding two tuples                     [0m[37m features/tuples.feature:29:11
Dec 01 23:15:35.207 INFO [stdout] [0m[0m[32m  ✔ Given a1 := tuple(3, -2, 5, 1)                [0m[37m features/tuples.feature:30:3
Dec 01 23:15:35.207 INFO [stdout] [0m[0m[32m  ✔ And a2 := tuple(-2, 3, 1, 0)                  [0m[37m features/tuples.feature:31:5
Dec 01 23:15:35.207 INFO [stdout] [0m[0m[32m  ✔ Then a1 + a2 = tuple(1, 1, 6, 1)              [0m[37m features/tuples.feature:32:4
Dec 01 23:15:35.222 INFO [stdout] [0m
Dec 01 23:15:35.223 INFO [stdout] [0m[1m[37m Scenario: Subtracting two points                [0m[37m features/tuples.feature:34:11
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ Given p1 := point(3, 2, 1)                    [0m[37m features/tuples.feature:35:3
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ And p2 := point(5, 6, 7)                      [0m[37m features/tuples.feature:36:5
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ Then p1 - p2 = vector(-2, -4, -6)             [0m[37m features/tuples.feature:37:3
Dec 01 23:15:35.223 INFO [stdout] [0m
Dec 01 23:15:35.223 INFO [stdout] [0m[1m[37m Scenario: Subtracting a vector from a point     [0m[37m features/tuples.feature:39:11
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ Given p := point(3, 2, 1)                     [0m[37m features/tuples.feature:40:3
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ And v := vector(5, 6, 7)                      [0m[37m features/tuples.feature:41:5
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ Then p - v = point(-2, -4, -6)                [0m[37m features/tuples.feature:42:3
Dec 01 23:15:35.223 INFO [stdout] [0m
Dec 01 23:15:35.223 INFO [stdout] [0m[1m[37m Scenario: Subtracting two vectors               [0m[37m features/tuples.feature:44:11
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ Given v1 := vector(3, 2, 1)                   [0m[37m features/tuples.feature:45:3
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ And v2 := vector(5, 6, 7)                     [0m[37m features/tuples.feature:46:5
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ Then v1 - v2 = vector(-2, -4, -6)             [0m[37m features/tuples.feature:47:3
Dec 01 23:15:35.223 INFO [stdout] [0m
Dec 01 23:15:35.223 INFO [stdout] [0m[1m[37m Scenario: Subtracting a vector from the zero vector
Dec 01 23:15:35.223 INFO [stdout]                                                  [0m[37m features/tuples.feature:49:11
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ Given zero := vector(0, 0, 0)                 [0m[37m features/tuples.feature:50:3
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ And v2 := vector(1, -2, 3)                    [0m[37m features/tuples.feature:51:5
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ Then zero - v2 = vector(-1, 2, -3)            [0m[37m features/tuples.feature:52:3
Dec 01 23:15:35.223 INFO [stdout] [0m
Dec 01 23:15:35.223 INFO [stdout] [0m[1m[37m Scenario: Negating a tuple                      [0m[37m features/tuples.feature:54:11
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ Given a := tuple(1, -2, 3, -4)                [0m[37m features/tuples.feature:55:3
Dec 01 23:15:35.223 INFO [stdout] [0m[0m[32m  ✔ Then -a = tuple(-1, 2, -3, 4)                 [0m[37m features/tuples.feature:56:3
Dec 01 23:15:35.231 INFO [stdout] [0m
Dec 01 23:15:35.231 INFO [stdout] [0m[1m[37m Scenario: Multiplying a tuple by a scalar       [0m[37m features/tuples.feature:58:11
Dec 01 23:15:35.231 INFO [stdout] [0m[0m[32m  ✔ Given a := tuple(1, -2, 3, -4)                [0m[37m features/tuples.feature:59:3
Dec 01 23:15:35.231 INFO [stdout] [0m[0m[32m  ✔ Then a * 3.5 = tuple(3.5, -7, 10.5, -14)      [0m[37m features/tuples.feature:60:3
Dec 01 23:15:35.231 INFO [stdout] [0m
Dec 01 23:15:35.231 INFO [stdout] [0m[1m[37m Scenario: Multiplying a tuple by a fraction     [0m[37m features/tuples.feature:62:11
Dec 01 23:15:35.231 INFO [stdout] [0m[0m[32m  ✔ Given a := tuple(1, -2, 3, -4)                [0m[37m features/tuples.feature:63:3
Dec 01 23:15:35.231 INFO [stdout] [0m[0m[32m  ✔ Then a * 0.5 = tuple(0.5, -1, 1.5, -2)        [0m[37m features/tuples.feature:64:3
Dec 01 23:15:35.231 INFO [stdout] [0m
Dec 01 23:15:35.231 INFO [stdout] [0m[1m[37m Scenario: Dividing a tuple by a scalar          [0m[37m features/tuples.feature:66:11
Dec 01 23:15:35.231 INFO [stdout] [0m[0m[32m  ✔ Given a := tuple(1, -2, 3, -4)                [0m[37m features/tuples.feature:67:3
Dec 01 23:15:35.231 INFO [stdout] [0m[0m[32m  ✔ Then a / 2 = tuple(0.5, -1, 1.5, -2)          [0m[37m features/tuples.feature:68:3
Dec 01 23:15:35.239 INFO [stdout] [0m
Dec 01 23:15:35.239 INFO [stdout] [0m[1m[37m Scenario: Magnitude of vector(1, 0, 0)          [0m[37m features/tuples.feature:70:11
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Given v := vector(1, 0, 0)                    [0m[37m features/tuples.feature:71:3
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Then magnitude(v) = 1                         [0m[37m features/tuples.feature:72:3
Dec 01 23:15:35.239 INFO [stdout] [0m
Dec 01 23:15:35.239 INFO [stdout] [0m[1m[37m Scenario: Magnitude of vector(0, 1, 0)          [0m[37m features/tuples.feature:74:11
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Given v := vector(0, 1, 0)                    [0m[37m features/tuples.feature:75:3
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Then magnitude(v) = 1                         [0m[37m features/tuples.feature:76:3
Dec 01 23:15:35.239 INFO [stdout] [0m
Dec 01 23:15:35.239 INFO [stdout] [0m[1m[37m Scenario: Magnitude of vector(0, 0, 1)          [0m[37m features/tuples.feature:78:11
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Given v := vector(0, 0, 1)                    [0m[37m features/tuples.feature:79:3
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Then magnitude(v) = 1                         [0m[37m features/tuples.feature:80:3
Dec 01 23:15:35.239 INFO [stdout] [0m
Dec 01 23:15:35.239 INFO [stdout] [0m[1m[37m Scenario: Magnitude of vector(1, 2, 3)          [0m[37m features/tuples.feature:82:11
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Given v := vector(1, 2, 3)                    [0m[37m features/tuples.feature:83:3
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Then magnitude(v) = √14                       [0m[37m features/tuples.feature:84:3
Dec 01 23:15:35.239 INFO [stdout] [0m
Dec 01 23:15:35.239 INFO [stdout] [0m[1m[37m Scenario: Magnitude of vector(-1, -2, -3)       [0m[37m features/tuples.feature:86:11
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Given v := vector(-1, -2, -3)                 [0m[37m features/tuples.feature:87:3
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Then magnitude(v) = √14                       [0m[37m features/tuples.feature:88:3
Dec 01 23:15:35.239 INFO [stdout] [0m
Dec 01 23:15:35.239 INFO [stdout] [0m[1m[37m Scenario: Normalizing vector(4, 0, 0) gives (1, 0, 0)
Dec 01 23:15:35.239 INFO [stdout]                                                  [0m[37m features/tuples.feature:90:11
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Given v := vector(4, 0, 0)                    [0m[37m features/tuples.feature:91:3
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Then normalize(v) = vector(1, 0, 0)           [0m[37m features/tuples.feature:92:3
Dec 01 23:15:35.239 INFO [stdout] [0m
Dec 01 23:15:35.239 INFO [stdout] [0m[1m[37m Scenario: Normalizing vector(1, 2, 3)           [0m[37m features/tuples.feature:94:11
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Given v := vector(1, 2, 3)                    [0m[37m features/tuples.feature:95:3
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Then normalize(v) = approximately vector(0.26726, 0.53452, 0.80178)
Dec 01 23:15:35.239 INFO [stdout]                                                   [0m[37m features/tuples.feature:97:3
Dec 01 23:15:35.239 INFO [stdout] [0m
Dec 01 23:15:35.239 INFO [stdout] [0m[1m[37m Scenario: The magnitude of a normalized vector  [0m[37m features/tuples.feature:99:11
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[32m  ✔ Given v := vector(1, 2, 3)                   [0m[37m features/tuples.feature:100:3
Dec 01 23:15:35.239 INFO [stdout] [0m[0m[36m  - When norm := normalize(v)                    [0m[37m features/tuples.feature:101:3
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stderr]    Doc-tests raytracer_challenge
Dec 01 23:15:35.247 INFO [stdout] [0m[36m  - Then magnitude(norm) = 1                     [0m[37m features/tuples.feature:102:3
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] 
Dec 01 23:15:35.247 INFO [stdout] [0m[1m[37m Scenario: The dot product of two tuples        [0m[37m features/tuples.feature:104:11
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[36m  - Given a := vector(1, 2, 3)                   [0m[37m features/tuples.feature:105:3
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] [0m[36m  - And b := vector(2, 3, 4)                     [0m[37m features/tuples.feature:106:5
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] [0m[36m  - Then a dot b = 20                            [0m[37m features/tuples.feature:107:3
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] 
Dec 01 23:15:35.247 INFO [stdout] [0m[1m[37m Scenario: Cross product of two vectors         [0m[37m features/tuples.feature:109:11
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[36m  - Given a := vector(1, 2, 3)                   [0m[37m features/tuples.feature:110:3
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] [0m[36m  - And b := vector(2, 3, 4)                     [0m[37m features/tuples.feature:111:5
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] [0m[36m  - Then a cross b = vector(-1, 2, -1)           [0m[37m features/tuples.feature:112:3
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] [0m[36m  - And b cross a = vector(1, -2, 1)             [0m[37m features/tuples.feature:113:5
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] 
Dec 01 23:15:35.247 INFO [stdout] [0m[1m[37m Scenario: Colors are (red, green, blue) tuples [0m[37m features/tuples.feature:115:11
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[36m  - Given c := color(-0.5, 0.4, 1.7)             [0m[37m features/tuples.feature:116:3
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] [0m[36m  - Then c.red = -0.5                            [0m[37m features/tuples.feature:117:3
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] [0m[36m  - And c.green = 0.4                            [0m[37m features/tuples.feature:118:5
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] [0m[36m  - And c.blue = 1.7                             [0m[37m features/tuples.feature:119:5
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] 
Dec 01 23:15:35.247 INFO [stdout] [0m[1m[37m Scenario: Adding colors                        [0m[37m features/tuples.feature:121:11
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[36m  - Given c1 := color(0.9, 0.6, 0.75)            [0m[37m features/tuples.feature:122:3
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] [0m[36m  - And c2 := color(0.7, 0.1, 0.25)              [0m[37m features/tuples.feature:123:5
Dec 01 23:15:35.247 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.247 INFO [stdout] [0m[36m  - Then c1 + c2 = color(1.6, 0.7, 1.0)          [0m[37m features/tuples.feature:124:4
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] 
Dec 01 23:15:35.248 INFO [stdout] [0m[1m[37m Scenario: Subtracting colors                   [0m[37m features/tuples.feature:126:11
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[36m  - Given c1 := color(0.9, 0.6, 0.75)            [0m[37m features/tuples.feature:127:3
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] [0m[36m  - And c2 := color(0.7, 0.1, 0.25)              [0m[37m features/tuples.feature:128:5
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] [0m[36m  - Then c1 - c2 = color(0.2, 0.5, 0.5)          [0m[37m features/tuples.feature:129:4
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] 
Dec 01 23:15:35.248 INFO [stdout] [0m[1m[37m Scenario: Multiplying a color by a scalar      [0m[37m features/tuples.feature:131:11
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[36m  - Given c := color(0.2, 0.3, 0.4)              [0m[37m features/tuples.feature:132:3
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] [0m[36m  - Then c * 2 = color(0.4, 0.6, 0.8)            [0m[37m features/tuples.feature:133:3
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] 
Dec 01 23:15:35.248 INFO [stdout] [0m[1m[37m Scenario: Multiplying colors                   [0m[37m features/tuples.feature:135:11
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[36m  - Given c1 := color(1, 0.2, 0.4)               [0m[37m features/tuples.feature:136:3
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] [0m[36m  - And c2 := color(0.9, 1, 0.1)                 [0m[37m features/tuples.feature:137:5
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] [0m[36m  - Then c1 * c2 = color(0.9, 0.2, 0.04)         [0m[37m features/tuples.feature:138:4
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] 
Dec 01 23:15:35.248 INFO [stdout] [0m[1m[37m Scenario: Reflecting a vector approaching at 45°
Dec 01 23:15:35.248 INFO [stdout]                                                 [0m[37m features/tuples.feature:140:11
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[32m  ✔ Given v := vector(1, -1, 0)                  [0m[37m features/tuples.feature:141:3
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[36m  - And n := vector(0, 1, 0)                     [0m[37m features/tuples.feature:142:5
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] [0m[36m  - When r := reflect(v, n)                      [0m[37m features/tuples.feature:143:3
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] [0m[36m  - Then r = vector(1, 1, 0)                     [0m[37m features/tuples.feature:144:3
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] 
Dec 01 23:15:35.248 INFO [stdout] [0m[1m[37m Scenario: Reflecting a vector off a slanted surface
Dec 01 23:15:35.248 INFO [stdout]                                                 [0m[37m features/tuples.feature:146:11
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[32m  ✔ Given v := vector(0, -1, 0)                  [0m[37m features/tuples.feature:147:3
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[36m  - And n := vector(√2/2, √2/2, 0)               [0m[37m features/tuples.feature:148:5
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] [0m[36m  - When r := reflect(v, n)                      [0m[37m features/tuples.feature:149:3
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] [0m[36m  - Then r = vector(1, 0, 0)                     [0m[37m features/tuples.feature:150:3
Dec 01 23:15:35.248 INFO [stdout] [0m[0m[33m    ⚡ [0mNot yet implemented (skipped)
Dec 01 23:15:35.248 INFO [stdout] 
Dec 01 23:15:35.248 INFO [stdout] [0m[1m[37m1 features
Dec 01 23:15:35.248 INFO [stdout] 30 scenarios ([0m[1m[36m10 skipped[0m[1m[37m, [0m[1m[32m20 passed[0m[1m[37m)
Dec 01 23:15:35.248 INFO [stdout] 88 steps ([0m[1m[36m30 skipped[0m[1m[37m, [0m[1m[32m58 passed[0m[1m[37m)
Dec 01 23:15:35.248 INFO [stdout] [0m
Dec 01 23:15:36.067 INFO [stdout] 
Dec 01 23:15:36.067 INFO [stdout] running 0 tests
Dec 01 23:15:36.067 INFO [stdout] 
Dec 01 23:15:36.067 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 23:15:36.067 INFO [stdout] 
Dec 01 23:15:36.074 INFO [stderr] su: No module specific data is present
Dec 01 23:15:37.279 INFO running `"docker" "inspect" "b9b8e13100f3b55ab3d8501a43331f15b9bee2032429ce9b80b78bfdfff14690"`
Dec 01 23:15:37.553 INFO running `"docker" "rm" "-f" "b9b8e13100f3b55ab3d8501a43331f15b9bee2032429ce9b80b78bfdfff14690"`
Dec 01 23:15:37.855 INFO [stdout] b9b8e13100f3b55ab3d8501a43331f15b9bee2032429ce9b80b78bfdfff14690
